← Index
NYTProf Performance Profile   « line view »
For rbm/rbm
  Run on Wed Feb 12 20:36:06 2020
Reported on Wed Feb 12 21:42:27 2020

Filename/root/tor-browser-build/input text
StatementsExecuted 1425688 statements in 8.51s
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
146492113.88s10732sTemplate::Document::::__ANON__[input text:9]Template::Document::__ANON__[input text:9]
1991111.69s613sTemplate::Document::::__ANON__[input text:43]Template::Document::__ANON__[input text:43]
376400111.33s1.33sTemplate::Document::::CORE:substTemplate::Document::CORE:subst (opcode)
37640011931ms931msTemplate::Document::::CORE:matchTemplate::Document::CORE:match (opcode)
100811365ms268sTemplate::Document::::__ANON__[input text:35]Template::Document::__ANON__[input text:35]
798211308ms9708sTemplate::Document::::__ANON__[input text:10]Template::Document::__ANON__[input text:10]
199111264ms4.81sTemplate::Document::::__ANON__[input text:22]Template::Document::__ANON__[input text:22]
199011256ms8952sTemplate::Document::::__ANON__[input text:18]Template::Document::__ANON__[input text:18]
199111220ms179sTemplate::Document::::__ANON__[input text:17]Template::Document::__ANON__[input text:17]
297811157ms25.6sTemplate::Document::::__ANON__[input text:13]Template::Document::__ANON__[input text:13]
9951188.6ms14.9sTemplate::Document::::__ANON__[input text:30]Template::Document::__ANON__[input text:30]
28111.69ms4.89sTemplate::Document::::__ANON__[input text:11]Template::Document::__ANON__[input text:11]
9111.52ms1.97sTemplate::Document::::__ANON__[input text:21]Template::Document::__ANON__[input text:21]
111112µs12.3msTemplate::Document::::__ANON__[input text:25]Template::Document::__ANON__[input text:25]
11141µs2.77msTemplate::Document::::__ANON__[input text:12]Template::Document::__ANON__[input text:12]
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
12133872.36s29329239426s
# spent 8952s (256ms+8951) within Template::Document::__ANON__[input text:18] which was called 1990 times, avg 4.50s/call: # 1990 times (256ms+8951s) by Template::Document::process at line 163 of Template/Document.pm, avg 4.50s/call # spent 1.97s (1.52ms+1.97) within Template::Document::__ANON__[input text:21] which was called 9 times, avg 219ms/call: # 9 times (1.52ms+1.97s) by Template::Document::process at line 163 of Template/Document.pm, avg 219ms/call # spent 12.3ms (112µs+12.2) within Template::Document::__ANON__[input text:25] which was called: # once (112µs+12.2ms) by Template::Document::process at line 163 of Template/Document.pm # spent 4.81s (264ms+4.55) within Template::Document::__ANON__[input text:22] which was called 1991 times, avg 2.42ms/call: # 1991 times (264ms+4.55s) by Template::Document::process at line 163 of Template/Document.pm, avg 2.42ms/call # spent 10732s (3.88+10728) within Template::Document::__ANON__[input text:9] which was called 146492 times, avg 73.3ms/call: # 146492 times (3.88s+10728s) by Template::Document::process at line 163 of Template/Document.pm, avg 73.3ms/call # spent 2.77ms (41µs+2.73) within Template::Document::__ANON__[input text:12] which was called: # once (41µs+2.73ms) by Template::Document::process at line 163 of Template/Document.pm # spent 613s (1.69+612) within Template::Document::__ANON__[input text:43] which was called 1991 times, avg 308ms/call: # 1991 times (1.69s+612s) by Template::Document::process at line 163 of Template/Document.pm, avg 308ms/call # spent 268s (365ms+267) within Template::Document::__ANON__[input text:35] which was called 1008 times, avg 265ms/call: # 1008 times (365ms+267s) by Template::Document::process at line 163 of Template/Document.pm, avg 265ms/call # spent 14.9s (88.6ms+14.9) within Template::Document::__ANON__[input text:30] which was called 995 times, avg 15.0ms/call: # 995 times (88.6ms+14.9s) by Template::Document::process at line 163 of Template/Document.pm, avg 15.0ms/call # spent 9708s (308ms+9708) within Template::Document::__ANON__[input text:10] which was called 7982 times, avg 1.22s/call: # 7982 times (308ms+9708s) by Template::Document::process at line 163 of Template/Document.pm, avg 1.22s/call # spent 25.6s (157ms+25.4) within Template::Document::__ANON__[input text:13] which was called 2978 times, avg 8.59ms/call: # 2978 times (157ms+25.4s) by Template::Document::process at line 163 of Template/Document.pm, avg 8.59ms/call # spent 179s (220ms+179) within Template::Document::__ANON__[input text:17] which was called 1991 times, avg 90.0ms/call: # 1991 times (220ms+179s) by Template::Document::process at line 163 of Template/Document.pm, avg 90.0ms/call # spent 4.89s (1.69ms+4.89) within Template::Document::__ANON__[input text:11] which was called 28 times, avg 175ms/call: # 28 times (1.69ms+4.89s) by Template::Document::process at line 163 of Template/Document.pm, avg 175ms/call
$output .= $stash->get('project');
# spent 19132s making 194420 calls to Template::Stash::XS::get, avg 98.4ms/call # spent 19099s making 77850 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 245ms/call # spent 1329s making 998 calls to Template::Context::include, avg 1.33s/call, recursion: max depth 1, sum of overlapping time 165s # spent 31.0s making 3982 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 7.79ms/call # spent 380ms making 4007 calls to RBM::__ANON__[rbm/lib/RBM.pm:667], avg 95µs/call # spent 32.7ms making 3981 calls to Template::VMethods::text_substr, avg 8µs/call # spent 15.0ms making 6063 calls to Template::Stash::undefined, avg 2µs/call # spent 9.60ms making 1991 calls to Template::Stash::XS::set, avg 5µs/call
2203256467ms47703391s } };
# spent 196s making 23851 calls to Template::Stash::XS::get, avg 8.21ms/call # spent 194s making 18883 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 10.3ms/call # spent 1.84s making 2977 calls to RBM::__ANON__[rbm/lib/RBM.pm:660], avg 618µs/call # spent 4.68ms making 1992 calls to Template::Stash::undefined, avg 2µs/call
3172395213ms17915135s if ($@) {
# spent 67.7s making 13935 calls to Template::Stash::XS::get, avg 4.86ms/call # spent 41.7s making 2987 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 14.0ms/call # spent 25.9s making 993 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 26.1ms/call
42395573.2ms705610.6s $_tt_error = $context->catch($@, \$output);
# spent 5.30s making 5011 calls to Template::Stash::XS::get, avg 1.06ms/call # spent 5.26s making 2009 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 2.62ms/call # spent 924µs making 18 calls to String::ShellQuote::shell_quote, avg 51µs/call # spent 116µs making 18 calls to Template::Stash::XS::set, avg 6µs/call
51099152.8ms80079.82s die $_tt_error unless $_tt_error->type eq 'return';
# spent 4.91s making 2005 calls to Template::Stash::XS::get, avg 2.45ms/call # spent 4.89s making 2005 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 2.44ms/call # spent 30.2ms making 3982 calls to Template::Iterator::get_next, avg 8µs/call # spent 42µs making 15 calls to Template::Stash::undefined, avg 3µs/call
616933174ms24907110s }
# spent 54.9s making 11955 calls to Template::Stash::XS::get, avg 4.59ms/call # spent 39.6s making 8971 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 4.42ms/call # spent 15.1s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 7.60ms/call # spent 5.07ms making 1990 calls to Template::Stash::undefined, avg 3µs/call
727928156ms1797116791s
# spent 8396s making 8990 calls to Template::Stash::XS::get, avg 934ms/call # spent 8380s making 6990 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 1.20s/call # spent 15.3s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 7.70ms/call
82979901.00s15015138s return $output;
# spent 68.9s making 7010 calls to Template::Stash::XS::get, avg 9.83ms/call # spent 68.9s making 5997 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 11.5ms/call # spent 5.79ms making 1991 calls to Template::Stash::XS::set, avg 3µs/call # spent 796µs making 15 calls to String::ShellQuote::shell_quote, avg 53µs/call # spent 8µs making 2 calls to Template::Stash::undefined, avg 4µs/call
922969195ms89871075s}
# spent 538s making 3996 calls to Template::Stash::XS::get, avg 135ms/call # spent 537s making 3001 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 179ms/call # spent 1.17s making 995 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 1.18ms/call # spent 3.03ms making 995 calls to Template::Stash::undefined, avg 3µs/call
10158487879ms50225.22s
# spent 2.61s making 1016 calls to Template::Stash::XS::get, avg 2.57ms/call # spent 2.60s making 1016 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 2.56ms/call # spent 13.7ms making 981 calls to Template::Iterator::get_next, avg 14µs/call # spent 2.07ms making 1991 calls to UNIVERSAL::isa, avg 1µs/call # spent 79µs making 15 calls to Template::Stash::XS::set, avg 5µs/call # spent 5µs making 3 calls to Template::Stash::undefined, avg 2µs/call
111105401.93s2220321159s
# spent 580s making 126452 calls to Template::Stash::XS::get, avg 4.59ms/call # spent 532s making 31867 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 16.7ms/call # spent 25.6s making 19910 calls to RBM::__ANON__[rbm/lib/RBM.pm:662], avg 1.28ms/call # spent 21.4s making 7964 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 2.68ms/call # spent 140ms making 1991 calls to Template::Config::iterator, avg 70µs/call # spent 103ms making 23893 calls to Template::Stash::XS::set, avg 4µs/call # spent 66.7ms making 1991 calls to Template::VMethods::list_sort, avg 33µs/call # spent 26.3ms making 1991 calls to Template::Iterator::AUTOLOAD, avg 13µs/call # spent 22.6ms making 1991 calls to Template::VMethods::list_import, avg 11µs/call # spent 11.4ms making 3982 calls to Template::Stash::undefined, avg 3µs/call
121200349.4ms20613.72s
# spent 1.87s making 1036 calls to Template::Stash::XS::get, avg 1.81ms/call # spent 1.85s making 1025 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 1.80ms/call
13600532.1ms34802.12s
# spent 1.07s making 1008 calls to Template::Stash::XS::get, avg 1.06ms/call # spent 1.05s making 1008 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 1.04ms/call # spent 4.33ms making 993 calls to Template::Stash::XS::set, avg 4µs/call # spent 1.69ms making 471 calls to Template::Stash::undefined, avg 4µs/call
1427908190ms19940331ms
# spent 331ms making 19910 calls to Template::Iterator::get_next, avg 17µs/call # spent 288µs making 15 calls to Template::Stash::XS::get, avg 19µs/call # spent 124µs making 15 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 8µs/call
15700451.2ms69826.33s
# spent 2.54s making 2988 calls to Template::Stash::XS::get, avg 850µs/call # spent 2.52s making 995 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 2.54ms/call # spent 1.23s making 15 calls to Template::Context::include, avg 81.8ms/call # spent 38.6ms making 1991 calls to Template::Iterator::get_first, avg 19µs/call # spent 1.16ms making 993 calls to UNIVERSAL::isa, avg 1µs/call
161195963.7ms5969110ms
# spent 65.0ms making 993 calls to Template::Config::iterator, avg 65µs/call # spent 21.5ms making 1992 calls to Template::Stash::XS::get, avg 11µs/call # spent 18.5ms making 993 calls to Template::Iterator::AUTOLOAD, avg 19µs/call # spent 5.00ms making 1991 calls to Template::Stash::undefined, avg 3µs/call
17895785.4ms89522.17s
# spent 1.09s making 993 calls to Template::Stash::XS::get, avg 1.10ms/call # spent 1.06s making 993 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 1.07ms/call # spent 17.2ms making 3982 calls to Template::Stash::XS::set, avg 4µs/call # spent 2.58ms making 993 calls to Template::Stash::undefined, avg 3µs/call # spent 1.73ms making 1991 calls to UNIVERSAL::isa, avg 868ns/call
18697240.1ms49922.33s
# spent 1.12s making 1003 calls to Template::Stash::XS::get, avg 1.12ms/call # spent 1.10s making 999 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 1.10ms/call # spent 104ms making 1991 calls to Template::Config::iterator, avg 52µs/call # spent 2.73ms making 995 calls to Template::Stash::undefined, avg 3µs/call # spent 70µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:667], avg 17µs/call
19697020.3ms
20745936.5ms348037.6ms
# spent 21.5ms making 1991 calls to Template::Iterator::AUTOLOAD, avg 11µs/call # spent 14.3ms making 993 calls to Template::Iterator::get_first, avg 14µs/call # spent 1.70ms making 488 calls to Template::Stash::XS::get, avg 3µs/call # spent 61µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:667], avg 15µs/call # spent 37µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 9µs/call
21845827.6ms206021.5ms
# spent 18.9ms making 1023 calls to Template::Stash::XS::get, avg 18µs/call # spent 2.19ms making 1007 calls to Template::Stash::undefined, avg 2µs/call # spent 383µs making 30 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 13µs/call
22453220.4ms36211.98s
# spent 965ms making 75 calls to Template::Stash::XS::get, avg 12.9ms/call # spent 963ms making 60 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 16.1ms/call # spent 37.5ms making 1991 calls to Template::Iterator::get_first, avg 19µs/call # spent 4.18ms making 993 calls to Template::Stash::XS::set, avg 4µs/call # spent 3.58ms making 480 calls to Template::Iterator::get_next, avg 7µs/call # spent 1.23ms making 15 calls to RBM::__ANON__[rbm/lib/RBM.pm:667], avg 82µs/call # spent 24µs making 7 calls to Template::Stash::undefined, avg 3µs/call
23699645.1ms542246.1ms
# spent 32.4ms making 2711 calls to Template::Stash::XS::get, avg 12µs/call # spent 9.93ms making 720 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 14µs/call # spent 3.74ms making 1991 calls to Template::Stash::undefined, avg 2µs/call
24625837.3ms47131.92s
# spent 965ms making 1001 calls to Template::Stash::XS::get, avg 964µs/call # spent 950ms making 997 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 953µs/call # spent 7.44ms making 1991 calls to Template::Stash::XS::set, avg 4µs/call # spent 1.53ms making 720 calls to Template::Stash::undefined, avg 2µs/call # spent 124µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:667], avg 31µs/call
2518199.25ms89311.2ms
# spent 6.38ms making 303 calls to Template::Stash::XS::get, avg 21µs/call # spent 4.03ms making 303 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 13µs/call # spent 818µs making 273 calls to Template::Stash::XS::set, avg 3µs/call # spent 24µs making 14 calls to Template::Stash::undefined, avg 2µs/call
26425889.1ms4965214s
# spent 107s making 2979 calls to Template::Stash::XS::get, avg 36.0ms/call # spent 107s making 1986 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 53.9ms/call
272244248.0ms273369µs
# spent 369µs making 273 calls to UNIVERSAL::isa, avg 1µs/call
285464.05ms54622.4ms
# spent 11.5ms making 273 calls to Template::Config::iterator, avg 42µs/call # spent 10.9ms making 273 calls to Template::Iterator::AUTOLOAD, avg 40µs/call
2920186.69ms
- -
3112686.73ms
322731.16ms2735.33ms
# spent 5.33ms making 273 calls to Template::Iterator::get_first, avg 20µs/call
33253775.5ms102285.12s
# spent 2.59s making 4255 calls to Template::Stash::XS::get, avg 610µs/call # spent 2.49s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:657], avg 1.25ms/call # spent 37.4ms making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:652], avg 19µs/call # spent 5.84ms making 1991 calls to Template::Stash::undefined, avg 3µs/call
34200113.0ms273959µs
# spent 959µs making 273 calls to Template::Stash::XS::set, avg 4µs/call
- -
36128115.5ms
3724712.02ms
38480193µs
- -
42199117.7ms
- -
44199117.4ms
 
# spent 931ms within Template::Document::CORE:match which was called 376400 times, avg 2µs/call: # 376400 times (931ms+0s) by Template::Document::new at line 75 of Template/Document.pm, avg 2µs/call
sub Template::Document::CORE:match; # opcode
# spent 1.33s within Template::Document::CORE:subst which was called 376400 times, avg 4µs/call: # 376400 times (1.33s+0s) by Template::Document::AUTOLOAD at line 187 of Template/Document.pm, avg 4µs/call
sub Template::Document::CORE:subst; # opcode
Report produced by the NYTProf 6.06 Perl profiler, developed by Tim Bunce based on work by Adam Kaplan and Salvador Fandiño García.